Feature: Book Genie Mode - Automated Testing with Detailed Reporting
File name: BookGenie.feature
Relative path: features/BookGenie.feature
Scenario Outline: Book Genie Response Validation with Citation Verification
When
I open the Creative Workspace login page
00:00:03.044
Then
I should see the homepage
00:00:00.044
When
I click on the mode selection dropdown
00:00:00.000
Then
I can see the "BookGenieQA" mode
00:00:00.000
And
I select the "BookGenieQA" mode
00:00:00.000
Then
I type "Suggest 5 books on christmas" on chat input element
00:00:03.502
And
I wait for AI to complete thinking
00:00:07.338
And
I validate the response is visible for "Suggest 5 books on christmas"
00:00:00.007
And
I extract book data from BookGenie response
00:00:00.007
And
I save BookGenie book data to Excel file for query "Suggest 5 books on christmas"
00:00:00.000
+ Show Error
Error: No book data extracted from BookGenie response.
at CustomWorld.(anonymous) (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:46:15)
at /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:8:71
at __awaiter (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:4:12)
at CustomWorld.(anonymous) (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:59:12)
And
I validate each book individually against Excel file for query "Suggest 5 books on christmas"
0s
And
I validate that reason texts match citation texts with 80% similarity
0s
And
I generate detailed citation validation report
0s
Then
I verify database connectivity
0s
And
database should contain 2020 books
0s
And
I validate extracted books individually against database
0s
And
I validate BookGenie response relevance with AI for query "Suggest 5 books on christmas"
0s
=== SCENARIO LOGS: Book Genie Response Validation with Citation Verification === [6:17:34 AM] Navigating to Creative Workspace login page [6:17:37 AM] Homepage visibility: false [6:17:37 AM] ℹ️ Typing query: "Suggest 5 books on christmas" [6:17:37 AM] ℹ️ Preparing to type query: "Suggest 5 books on christmas" [6:17:37 AM] ℹ️ Waiting for chat input to be visible [6:17:39 AM] ℹ️ Clearing chat input [6:17:39 AM] ℹ️ Typing query: "Suggest 5 books on christmas" [6:17:40 AM] ℹ️ Pressing Enter to submit query [6:17:41 AM] ✅ Query submitted successfully: "Suggest 5 books on christmas" [6:17:41 AM] ℹ️ Waiting for AI to complete thinking... [6:17:41 AM] 📋 WAITING FOR AI RESPONSE [6:17:41 AM] ℹ️ Looking for AI thinking indicator... [6:17:41 AM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes) [6:17:43 AM] ✅ ✓ AI thinking indicator appeared - AI is processing the request [6:17:43 AM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes) [6:17:45 AM] ✅ ✓ AI thinking completed - Response should be ready [6:17:45 AM] ℹ️ Allowing additional time for response rendering [6:17:48 AM] ✅ AI response wait process completed [6:17:48 AM] ℹ️ Validating response for query: "Suggest 5 books on christmas" [6:17:48 AM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 5 books on christmas" [6:17:48 AM] ℹ️ Locating the latest response in chat [6:17:48 AM] ℹ️ Waiting for response to be visible (max 30 seconds) [6:17:48 AM] ✅ Response received - Length: 620 characters [6:17:48 AM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...That sounds like a lovely theme! To help you find... [6:17:48 AM] ℹ️ Extracting book data from response... [6:17:48 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE [6:17:48 AM] ℹ️ Locating BookGenie response container [6:17:48 AM] ℹ️ Extracting HTML content from response [6:17:48 AM] ℹ️ HTML content extracted - Length: 7572 characters [6:17:48 AM] ℹ️ Parsing HTML to extract book data [6:17:48 AM] ✅ SUCCESSFULLY EXTRACTED 0 BOOKS [6:17:48 AM] 📋 EXTRACTED BOOKS SUMMARY [6:17:48 AM] 📊 Total books extracted: 0 [6:17:48 AM] 📊 Average relevance score: 0% [6:17:48 AM] ✅ Extracted 0 books from response === END LOGS ===
Scenario Logs: Book Genie Response Validation with Citation Verification
[6:17:34 AM] Navigating to Creative Workspace login page
[6:17:37 AM] Homepage visibility: false
[6:17:37 AM] ℹ️ Typing query: "Suggest 5 books on christmas"
[6:17:37 AM] ℹ️ Preparing to type query: "Suggest 5 books on christmas"
[6:17:37 AM] ℹ️ Waiting for chat input to be visible
[6:17:39 AM] ℹ️ Clearing chat input
[6:17:39 AM] ℹ️ Typing query: "Suggest 5 books on christmas"
[6:17:40 AM] ℹ️ Pressing Enter to submit query
[6:17:41 AM] ✅ Query submitted successfully: "Suggest 5 books on christmas"
[6:17:41 AM] ℹ️ Waiting for AI to complete thinking...
[6:17:41 AM] 📋 WAITING FOR AI RESPONSE
[6:17:41 AM] ℹ️ Looking for AI thinking indicator...
[6:17:41 AM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[6:17:43 AM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[6:17:43 AM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[6:17:45 AM] ✅ ✓ AI thinking completed - Response should be ready
[6:17:45 AM] ℹ️ Allowing additional time for response rendering
[6:17:48 AM] ✅ AI response wait process completed
[6:17:48 AM] ℹ️ Validating response for query: "Suggest 5 books on christmas"
[6:17:48 AM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 5 books on christmas"
[6:17:48 AM] ℹ️ Locating the latest response in chat
[6:17:48 AM] ℹ️ Waiting for response to be visible (max 30 seconds)
[6:17:48 AM] ✅ Response received - Length: 620 characters
[6:17:48 AM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...That sounds like a lovely theme! To help you find...
[6:17:48 AM] ℹ️ Extracting book data from response...
[6:17:48 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[6:17:48 AM] ℹ️ Locating BookGenie response container
[6:17:48 AM] ℹ️ Extracting HTML content from response
[6:17:48 AM] ℹ️ HTML content extracted - Length: 7572 characters
[6:17:48 AM] ℹ️ Parsing HTML to extract book data
[6:17:48 AM] ✅ SUCCESSFULLY EXTRACTED 0 BOOKS
[6:17:48 AM] 📋 EXTRACTED BOOKS SUMMARY
[6:17:48 AM] 📊 Total books extracted: 0
[6:17:48 AM] 📊 Average relevance score: 0%
[6:17:48 AM] ✅ Extracted 0 books from response
Scenario Outline: Book Genie Response Validation with Citation Verification
When
I open the Creative Workspace login page
00:00:02.615
Then
I should see the homepage
00:00:00.034
When
I click on the mode selection dropdown
00:00:00.000
Then
I can see the "BookGenieQA" mode
00:00:00.000
And
I select the "BookGenieQA" mode
00:00:00.000
Then
I type "Suggest 3 books for childrens" on chat input element
00:00:02.739
And
I wait for AI to complete thinking
00:00:13.698
And
I validate the response is visible for "Suggest 3 books for childrens"
00:00:00.006
And
I extract book data from BookGenie response
00:00:00.009
And
I save BookGenie book data to Excel file for query "Suggest 3 books for childrens"
00:00:00.011
And
I validate each book individually against Excel file for query "Suggest 3 books for childrens"
00:00:00.014
CITATION VALIDATION DETAILED REPORT ================================================================================ BOOK: Henry Huggins ------------------------------------------------------------ ✅ Reason 1: PASS (100%) ✅ Reason 2: PASS (95%) ✅ Reason 3: PASS (100%) BOOK: Anne of Green Gables ------------------------------------------------------------ ✅ Reason 1: PASS (100%) ✅ Reason 2: PASS (100%) ✅ Reason 3: PASS (100%) BOOK: The Secret Garden ------------------------------------------------------------ ✅ Reason 1: PASS (95%) ✅ Reason 2: PASS (95%) ✅ Reason 3: PASS (100%) SUMMARY: 9/9 reasons passed (100.0%) OVERALL STATUS: PASS
Citation Validation Summary: 9/9 passed
📊 Citation Validation Report
Generated on 10/16/2025, 6:19:39 AM
📖 Henry Huggins
✅ Reason 1: 100% match
✅ Reason 2: 95% match
✅ Reason 3: 100% match
📖 Anne of Green Gables
✅ Reason 1: 100% match
✅ Reason 2: 100% match
✅ Reason 3: 100% match
📖 The Secret Garden
✅ Reason 1: 95% match
✅ Reason 2: 95% match
✅ Reason 3: 100% match
📈 Validation Summary
Total Reasons Validated: 9
Passed: 9
Failed: 0
Pass Rate: 100.0%
Overall Status: PASS
Then
I verify database connectivity
00:00:00.351
And
database should contain 2020 books
00:00:00.180
And
I validate extracted books individually against database
00:00:00.536
And
I validate BookGenie response relevance with AI for query "Suggest 3 books for childrens"
00:00:06.265
+ Show Info
+ Show Info
PER-BOOK AI VALIDATION REPORT - Query: "Suggest 3 books for childrens"
================================================================================
OVERALL RELEVANCE SCORE: 70%
STATUS: FAIL
BOOKS ANALYZED: 3
BOOK 1: "Henry Huggins"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
BOOK 2: "Anne of Green Gables"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
BOOK 3: "The Secret Garden"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
SUMMARY FEEDBACK:
----------------------------------------
• AI analysis service is temporarily overloaded
• Using fallback analysis with neutral scores
• Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 12.904344956s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"location":"global","model":"gemini-2.5-pro"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"12s"}]
OVERALL IMPROVEMENT SUGGESTIONS:
----------------------------------------
• Retry the analysis when Gemini API is less busy
• Consider running tests during off-peak hours
• Check Google AI status page for service updates
🤖 PER-BOOK AI Response Relevance Validation
Query: "Suggest 3 books for childrens"
Overall Relevance Score: 70%
Status: FAIL
Books Analyzed: 3
Individual Book Analyses
📖 Book 1: "Henry Huggins" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
📖 Book 2: "Anne of Green Gables" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
📖 Book 3: "The Secret Garden" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
Summary Feedback
• AI analysis service is temporarily overloaded
• Using fallback analysis with neutral scores
• Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits. * Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2 Please retry in 12.904344956s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"location":"global","model":"gemini-2.5-pro"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"12s"}]
Overall Improvement Suggestions
💡 Retry the analysis when Gemini API is less busy
💡 Consider running tests during off-peak hours
💡 Check Google AI status page for service updates
=== SCENARIO LOGS: Book Genie Response Validation with Citation Verification ===
[6:17:50 AM] Navigating to Creative Workspace login page
[6:17:52 AM] Homepage visibility: false
[6:17:52 AM] ℹ️ Typing query: "Suggest 3 books for childrens"
[6:17:52 AM] ℹ️ Preparing to type query: "Suggest 3 books for childrens"
[6:17:52 AM] ℹ️ Waiting for chat input to be visible
[6:17:54 AM] ℹ️ Clearing chat input
[6:17:54 AM] ℹ️ Typing query: "Suggest 3 books for childrens"
[6:17:55 AM] ℹ️ Pressing Enter to submit query
[6:17:55 AM] ✅ Query submitted successfully: "Suggest 3 books for childrens"
[6:17:55 AM] ℹ️ Waiting for AI to complete thinking...
[6:17:55 AM] 📋 WAITING FOR AI RESPONSE
[6:17:55 AM] ℹ️ Looking for AI thinking indicator...
[6:17:55 AM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[6:17:57 AM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[6:17:57 AM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[6:18:06 AM] ✅ ✓ AI thinking completed - Response should be ready
[6:18:06 AM] ℹ️ Allowing additional time for response rendering
[6:18:09 AM] ✅ AI response wait process completed
[6:18:09 AM] ℹ️ Validating response for query: "Suggest 3 books for childrens"
[6:18:09 AM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 3 books for childrens"
[6:18:09 AM] ℹ️ Locating the latest response in chat
[6:18:09 AM] ℹ️ Waiting for response to be visible (max 30 seconds)
[6:18:09 AM] ✅ Response received - Length: 2030 characters
[6:18:09 AM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...Retrieving Relevant Books for 'books suitable for...
[6:18:09 AM] ℹ️ Extracting book data from response...
[6:18:09 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[6:18:09 AM] ℹ️ Locating BookGenie response container
[6:18:09 AM] ℹ️ Extracting HTML content from response
[6:18:09 AM] ℹ️ HTML content extracted - Length: 25302 characters
[6:18:09 AM] ℹ️ Parsing HTML to extract book data
[6:18:09 AM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[6:18:09 AM] 📋 EXTRACTED BOOKS SUMMARY
[6:18:09 AM] 📚 1. "Henry Huggins" - Score: 100%%
[6:18:09 AM] 📚 2. "Anne of Green Gables" - Score: 100%%
[6:18:09 AM] 📚 3. "The Secret Garden" - Score: 100%%
[6:18:09 AM] 📊 Total books extracted: 3
[6:18:09 AM] 📊 Average relevance score: 100%
[6:18:09 AM] ✅ Extracted 3 books from response
[6:18:09 AM] ℹ️ Saving 3 books to Excel file: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] 📋 SAVING BOOKS TO EXCEL FILE
[6:18:09 AM] ℹ️ Target file path: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] ℹ️ Exporting 3 books to Excel format
[6:18:09 AM] ✅ Book data saved to: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] 📊 File verified - Size: 16.87 KB
[6:18:09 AM] ✅ ✓ Excel file creation verified successfully
[6:18:09 AM] 📋 STARTING INDIVIDUAL BOOK VALIDATION (3 books) for query: "Suggest 3 books for childrens"
[6:18:09 AM] 📋 📚 VALIDATING BOOK 1: "Henry Huggins"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "Henry Huggins" vs expected: "Henry Huggins"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "Henry Huggins"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 1 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 1 validation completed
[6:18:09 AM] 📋 📚 VALIDATING BOOK 2: "Anne of Green Gables"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "Anne of Green Gables" vs expected: "Anne of Green Gables"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "Anne of Green Gables"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 2 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 2 validation completed
[6:18:09 AM] 📋 📚 VALIDATING BOOK 3: "The Secret Garden"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "The Secret Garden" vs expected: "The Secret Garden"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "The Secret Garden"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 3 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 3 validation completed
[6:18:09 AM] 📊 VALIDATION SUMMARY: 3/3 books passed
[6:18:09 AM] ✅ ALL BOOKS VALIDATED SUCCESSFULLY!
[6:18:09 AM] 📋 🔍 VALIDATING REASON-CITATION MATCHES
[6:18:09 AM] ℹ️ Starting citation validation process...
[6:18:09 AM] 📋 🚀 STARTING COMPLETE CITATION VALIDATION WORKFLOW
[6:18:09 AM] ℹ️ Step 1: Extracting books from AI response
[6:18:09 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[6:18:09 AM] ℹ️ Locating BookGenie response container
[6:18:09 AM] ℹ️ Extracting HTML content from response
[6:18:09 AM] ℹ️ HTML content extracted - Length: 25302 characters
[6:18:09 AM] ℹ️ Parsing HTML to extract book data
[6:18:09 AM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[6:18:09 AM] 📋 EXTRACTED BOOKS SUMMARY
[6:18:09 AM] 📚 1. "Henry Huggins" - Score: 100%%
[6:18:09 AM] 📚 2. "Anne of Green Gables" - Score: 100%%
[6:18:09 AM] 📚 3. "The Secret Garden" - Score: 100%%
[6:18:09 AM] 📊 Total books extracted: 3
[6:18:09 AM] 📊 Average relevance score: 100%
[6:18:09 AM] ℹ️ Step 2: Extracting citation texts from book elements
[6:18:09 AM] 📋 📚 EXTRACTING CITATION TEXTS FROM BOOK ELEMENTS
[6:18:09 AM] ℹ️ Step 1: Waiting for main book section
[6:18:09 AM] ℹ️ Found 7 total accordion sections
[6:18:09 AM] ℹ️ Found individual book: "1. Henry Huggins"
[6:18:09 AM] ℹ️ Found individual book: "2. Anne of Green Gables"
[6:18:09 AM] ℹ️ Found individual book: "3. The Secret Garden"
[6:18:09 AM] ℹ️ Processing 3 individual book sections
[6:18:09 AM] 📋 📖 PROCESSING BOOK 1: "Henry Huggins"
[6:18:09 AM] ℹ️ Expanding book section: "Henry Huggins"
[6:18:12 AM] ✅ Book section expanded: "Henry Huggins"
[6:18:12 AM] ℹ️ Expanding "Why this book is the match" section for: "Henry Huggins"
[6:18:15 AM] ✅ "Why this book is the match" section expanded for: "Henry Huggins"
[6:18:15 AM] ℹ️ Found 3 citation buttons for "Henry Huggins"
[6:18:15 AM] ℹ️ Extracting citation 1/3 for "Henry Huggins"
[6:18:15 AM] ℹ️ Processing metadata citation 1 for "Henry Huggins"
[6:18:16 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:18:18 AM] ℹ️ Extracted citation text (first 100 chars): classic...
[6:18:18 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:18:20 AM] ✅ ✅ metadata citation 1 closed successfully
[6:18:20 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 7)
[6:18:20 AM] ℹ️ Extracting citation 2/3 for "Henry Huggins"
[6:18:20 AM] ℹ️ Processing metadata citation 2 for "Henry Huggins"
[6:18:21 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:18:23 AM] ℹ️ Extracted citation text (first 100 chars): Just as Henry Huggins is complaining that nothing exciting ever happens, a friendly dog sits down be...
[6:18:23 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:18:24 AM] ✅ ✅ metadata citation 2 closed successfully
[6:18:24 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 390)
[6:18:24 AM] ℹ️ Extracting citation 3/3 for "Henry Huggins"
[6:18:24 AM] ℹ️ Processing metadata citation 3 for "Henry Huggins"
[6:18:25 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:18:27 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:18:27 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:18:29 AM] ✅ ✅ metadata citation 3 closed successfully
[6:18:29 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:18:29 AM] ℹ️ Collapsing book section: "Henry Huggins"
[6:18:30 AM] ✅ Book section collapsed: "Henry Huggins"
[6:18:30 AM] ✅ ✅ Completed processing "Henry Huggins" - 3 citations extracted
[6:18:30 AM] 📋 📖 PROCESSING BOOK 2: "Anne of Green Gables"
[6:18:30 AM] ℹ️ Expanding book section: "Anne of Green Gables"
[6:18:33 AM] ✅ Book section expanded: "Anne of Green Gables"
[6:18:33 AM] ℹ️ Expanding "Why this book is the match" section for: "Anne of Green Gables"
[6:18:36 AM] ✅ "Why this book is the match" section expanded for: "Anne of Green Gables"
[6:18:36 AM] ℹ️ Found 3 citation buttons for "Anne of Green Gables"
[6:18:36 AM] ℹ️ Extracting citation 1/3 for "Anne of Green Gables"
[6:18:36 AM] ℹ️ Processing metadata citation 1 for "Anne of Green Gables"
[6:18:37 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:18:40 AM] ℹ️ Extracted citation text (first 100 chars): classic...
[6:18:40 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:18:41 AM] ✅ ✅ metadata citation 1 closed successfully
[6:18:41 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 7)
[6:18:41 AM] ℹ️ Extracting citation 2/3 for "Anne of Green Gables"
[6:18:41 AM] ℹ️ Processing metadata citation 2 for "Anne of Green Gables"
[6:18:42 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:18:44 AM] ℹ️ Extracted citation text (first 100 chars): a talkative eleven-year-old orphan with a heart full of dreams and a desperate longing for a home....
[6:18:44 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:18:46 AM] ✅ ✅ metadata citation 2 closed successfully
[6:18:46 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 98)
[6:18:46 AM] ℹ️ Extracting citation 3/3 for "Anne of Green Gables"
[6:18:46 AM] ℹ️ Processing metadata citation 3 for "Anne of Green Gables"
[6:18:47 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:18:49 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:18:49 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:18:50 AM] ✅ ✅ metadata citation 3 closed successfully
[6:18:50 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:18:50 AM] ℹ️ Collapsing book section: "Anne of Green Gables"
[6:18:52 AM] ✅ Book section collapsed: "Anne of Green Gables"
[6:18:52 AM] ✅ ✅ Completed processing "Anne of Green Gables" - 3 citations extracted
[6:18:52 AM] 📋 📖 PROCESSING BOOK 3: "The Secret Garden"
[6:18:52 AM] ℹ️ Expanding book section: "The Secret Garden"
[6:18:55 AM] ✅ Book section expanded: "The Secret Garden"
[6:18:55 AM] ℹ️ Expanding "Why this book is the match" section for: "The Secret Garden"
[6:18:58 AM] ✅ "Why this book is the match" section expanded for: "The Secret Garden"
[6:18:58 AM] ℹ️ Found 3 citation buttons for "The Secret Garden"
[6:18:58 AM] ℹ️ Extracting citation 1/3 for "The Secret Garden"
[6:18:58 AM] ℹ️ Processing metadata citation 1 for "The Secret Garden"
[6:18:59 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:19:01 AM] ℹ️ Extracted citation text (first 100 chars): classic literature...
[6:19:01 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:19:03 AM] ✅ ✅ metadata citation 1 closed successfully
[6:19:03 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 18)
[6:19:03 AM] ℹ️ Extracting citation 2/3 for "The Secret Garden"
[6:19:03 AM] ℹ️ Processing metadata citation 2 for "The Secret Garden"
[6:19:04 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:19:06 AM] ℹ️ Extracted citation text (first 100 chars): Mary discovers a secret garden, surrounded by walls and locked with a missing key. One day, with the...
[6:19:06 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:19:07 AM] ✅ ✅ metadata citation 2 closed successfully
[6:19:07 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 228)
[6:19:07 AM] ℹ️ Extracting citation 3/3 for "The Secret Garden"
[6:19:07 AM] ℹ️ Processing metadata citation 3 for "The Secret Garden"
[6:19:08 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:19:10 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:19:10 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:19:12 AM] ✅ ✅ metadata citation 3 closed successfully
[6:19:12 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:19:12 AM] ℹ️ Collapsing book section: "The Secret Garden"
[6:19:13 AM] ✅ Book section collapsed: "The Secret Garden"
[6:19:13 AM] ✅ ✅ Completed processing "The Secret Garden" - 3 citations extracted
[6:19:13 AM] ✅ 🎉 Successfully extracted citations from 3 books
[6:19:13 AM] ℹ️ Step 3: Validating reason-citation matches
[6:19:13 AM] 📋 🔍 VALIDATING REASON-CITATION MATCHES (80% THRESHOLD)
[6:19:13 AM] ℹ️ 📖 Validating 3 reasons for: "Henry Huggins"
[6:19:13 AM] ℹ️ Reason 1: Checking match...
[6:19:13 AM] ✅ ✅ "Henry Huggins" - Reason 1: 100% match - PASS
[6:19:13 AM] ℹ️ Reason 2: Checking match...
[6:19:21 AM] ✅ ✅ "Henry Huggins" - Reason 2: 95% match - PASS
[6:19:21 AM] ℹ️ Reason 3: Checking match...
[6:19:21 AM] ✅ ✅ "Henry Huggins" - Reason 3: 100% match - PASS
[6:19:21 AM] ℹ️ 📖 Validating 3 reasons for: "Anne of Green Gables"
[6:19:21 AM] ℹ️ Reason 1: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 1: 100% match - PASS
[6:19:21 AM] ℹ️ Reason 2: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 2: 100% match - PASS
[6:19:21 AM] ℹ️ Reason 3: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 3: 100% match - PASS
[6:19:21 AM] ℹ️ 📖 Validating 3 reasons for: "The Secret Garden"
[6:19:21 AM] ℹ️ Reason 1: Checking match...
[6:19:31 AM] ✅ ✅ "The Secret Garden" - Reason 1: 95% match - PASS
[6:19:31 AM] ℹ️ Reason 2: Checking match...
[6:19:39 AM] ✅ ✅ "The Secret Garden" - Reason 2: 95% match - PASS
[6:19:39 AM] ℹ️ Reason 3: Checking match...
[6:19:39 AM] ✅ ✅ "The Secret Garden" - Reason 3: 100% match - PASS
[6:19:39 AM] 📊 📊 CITATION VALIDATION SUMMARY: 9/9 passed (100.0%)
[6:19:39 AM] ✅ 🎉 REASON-CITATION VALIDATION: OVERALL PASSED
[6:19:39 AM] ℹ️ Step 4: Generating detailed report
[6:19:39 AM] 📋 📋 GENERATING CITATION VALIDATION REPORT
[6:19:39 AM] ℹ️ Report generated: 9/9 reasons passed
[6:19:39 AM] ✅ ✅ Citation validation workflow completed successfully
[6:19:39 AM] 📊 📈 OVERALL CITATION VALIDATION: 9/9 (100.0%)
[6:19:39 AM] ✅ ✅ REASON-CITATION VALIDATION: OVERALL PASSED
[6:19:39 AM] 📋 📋 GENERATING DETAILED CITATION VALIDATION REPORT
[6:19:39 AM] ℹ️ Generating HTML citation validation report
[6:19:39 AM] ✅ Generated HTML report for 9 reasons
[6:19:39 AM] ✅ ✅ Citation validation HTML report generated and attached
[6:19:39 AM] ✅ ✅ Citation validation plain text report attached
[6:19:39 AM] ℹ️ Checking database connectivity...
[6:19:40 AM] ✅ ✅ Database connected successfully
[6:19:40 AM] ℹ️ Database path: /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/test_data/database.xlsx
[6:19:40 AM] ℹ️ Total books: 10032
[6:19:40 AM] 📊 Database contains 10032 books
[6:19:40 AM] ✅ ✅ Database book count requirement met: 10032 >= 2020
[6:19:40 AM] 📋 🔍 VALIDATING EXTRACTED BOOKS INDIVIDUALLY AGAINST DATABASE
[6:19:40 AM] ℹ️ Checking book 1/3: "Henry Huggins"
[6:19:40 AM] ℹ️ Checking if book exists in database: "Henry Huggins"
[6:19:40 AM] ✅ ✅ Database match: "Henry Huggins" → "Henry Huggins 4-Book Collection"
[6:19:40 AM] ℹ️ Checking book 2/3: "Anne of Green Gables"
[6:19:40 AM] ℹ️ Checking if book exists in database: "Anne of Green Gables"
[6:19:40 AM] ✅ ✅ Database match: "Anne of Green Gables" → "Anne of Green Gables"
[6:19:40 AM] ℹ️ Checking book 3/3: "The Secret Garden"
[6:19:40 AM] ℹ️ Checking if book exists in database: "The Secret Garden"
[6:19:40 AM] ✅ ✅ Database match: "The Secret Garden" → "The Secret Garden"
[6:19:40 AM] 📊 INDIVIDUAL DATABASE VALIDATION SUMMARY: 3/3 books found in database
[6:19:40 AM] ✅ 🎉 ALL BOOKS VALIDATED SUCCESSFULLY AGAINST DATABASE!
[6:19:40 AM] 📋 🤖 VALIDATING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[6:19:40 AM] ℹ️ Starting Gemini AI per-book relevance validation...
[6:19:40 AM] 📋 🤖 ANALYZING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[6:19:40 AM] ℹ️ Starting Gemini AI analysis with retry logic...
[6:19:47 AM] ⚠ ⚠️ GEMINI VALIDATION: Using fallback analysis due to API issues
[6:19:47 AM] ℹ️ Overall Score: 70% (Fallback)
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "Henry Huggins" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "Anne of Green Gables" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "The Secret Garden" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] ℹ️ 📊 SUMMARY FEEDBACK:
[6:19:47 AM] ℹ️ • AI analysis service is temporarily overloaded
[6:19:47 AM] ℹ️ • Using fallback analysis with neutral scores
[6:19:47 AM] ℹ️ • Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 12.904344956s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"location":"global","model":"gemini-2.5-pro"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"12s"}]
[6:19:47 AM] ❌ ❌ GEMINI AI VALIDATION FAILED: 70% relevance score (required: 80%)
[6:19:47 AM] ℹ️ Continuing execution due to soft assertion...
=== END LOGS ===
Scenario Logs: Book Genie Response Validation with Citation Verification
[6:17:50 AM] Navigating to Creative Workspace login page
[6:17:52 AM] Homepage visibility: false
[6:17:52 AM] ℹ️ Typing query: "Suggest 3 books for childrens"
[6:17:52 AM] ℹ️ Preparing to type query: "Suggest 3 books for childrens"
[6:17:52 AM] ℹ️ Waiting for chat input to be visible
[6:17:54 AM] ℹ️ Clearing chat input
[6:17:54 AM] ℹ️ Typing query: "Suggest 3 books for childrens"
[6:17:55 AM] ℹ️ Pressing Enter to submit query
[6:17:55 AM] ✅ Query submitted successfully: "Suggest 3 books for childrens"
[6:17:55 AM] ℹ️ Waiting for AI to complete thinking...
[6:17:55 AM] 📋 WAITING FOR AI RESPONSE
[6:17:55 AM] ℹ️ Looking for AI thinking indicator...
[6:17:55 AM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[6:17:57 AM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[6:17:57 AM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[6:18:06 AM] ✅ ✓ AI thinking completed - Response should be ready
[6:18:06 AM] ℹ️ Allowing additional time for response rendering
[6:18:09 AM] ✅ AI response wait process completed
[6:18:09 AM] ℹ️ Validating response for query: "Suggest 3 books for childrens"
[6:18:09 AM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 3 books for childrens"
[6:18:09 AM] ℹ️ Locating the latest response in chat
[6:18:09 AM] ℹ️ Waiting for response to be visible (max 30 seconds)
[6:18:09 AM] ✅ Response received - Length: 2030 characters
[6:18:09 AM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...Retrieving Relevant Books for 'books suitable for...
[6:18:09 AM] ℹ️ Extracting book data from response...
[6:18:09 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[6:18:09 AM] ℹ️ Locating BookGenie response container
[6:18:09 AM] ℹ️ Extracting HTML content from response
[6:18:09 AM] ℹ️ HTML content extracted - Length: 25302 characters
[6:18:09 AM] ℹ️ Parsing HTML to extract book data
[6:18:09 AM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[6:18:09 AM] 📋 EXTRACTED BOOKS SUMMARY
[6:18:09 AM] 📚 1. "Henry Huggins" - Score: 100%%
[6:18:09 AM] 📚 2. "Anne of Green Gables" - Score: 100%%
[6:18:09 AM] 📚 3. "The Secret Garden" - Score: 100%%
[6:18:09 AM] 📊 Total books extracted: 3
[6:18:09 AM] 📊 Average relevance score: 100%
[6:18:09 AM] ✅ Extracted 3 books from response
[6:18:09 AM] ℹ️ Saving 3 books to Excel file: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] 📋 SAVING BOOKS TO EXCEL FILE
[6:18:09 AM] ℹ️ Target file path: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] ℹ️ Exporting 3 books to Excel format
[6:18:09 AM] ✅ Book data saved to: test_results/suggest_3_books_for_childrens.xlsx
[6:18:09 AM] 📊 File verified - Size: 16.87 KB
[6:18:09 AM] ✅ ✓ Excel file creation verified successfully
[6:18:09 AM] 📋 STARTING INDIVIDUAL BOOK VALIDATION (3 books) for query: "Suggest 3 books for childrens"
[6:18:09 AM] 📋 📚 VALIDATING BOOK 1: "Henry Huggins"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "Henry Huggins" vs expected: "Henry Huggins"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "Henry Huggins"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 1 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 1 validation completed
[6:18:09 AM] 📋 📚 VALIDATING BOOK 2: "Anne of Green Gables"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "Anne of Green Gables" vs expected: "Anne of Green Gables"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "Anne of Green Gables"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 2 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 2 validation completed
[6:18:09 AM] 📋 📚 VALIDATING BOOK 3: "The Secret Garden"
[6:18:09 AM] ℹ️ Starting comprehensive book validation
[6:18:09 AM] ℹ️ Validating title: "The Secret Garden" vs expected: "The Secret Garden"
[6:18:09 AM] ✅ ✅ TITLE VALIDATION PASSED: "The Secret Garden"
[6:18:09 AM] ℹ️ Validating score: 100% vs expected: 100%
[6:18:09 AM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[6:18:09 AM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[6:18:09 AM] ℹ️ Why-match points: extracted 3 vs expected 3
[6:18:09 AM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[6:18:09 AM] ✅ 🎉 BOOK 3 VALIDATION: ALL CHECKS PASSED!
[6:18:09 AM] ℹ️ Book 3 validation completed
[6:18:09 AM] 📊 VALIDATION SUMMARY: 3/3 books passed
[6:18:09 AM] ✅ ALL BOOKS VALIDATED SUCCESSFULLY!
[6:18:09 AM] 📋 🔍 VALIDATING REASON-CITATION MATCHES
[6:18:09 AM] ℹ️ Starting citation validation process...
[6:18:09 AM] 📋 🚀 STARTING COMPLETE CITATION VALIDATION WORKFLOW
[6:18:09 AM] ℹ️ Step 1: Extracting books from AI response
[6:18:09 AM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[6:18:09 AM] ℹ️ Locating BookGenie response container
[6:18:09 AM] ℹ️ Extracting HTML content from response
[6:18:09 AM] ℹ️ HTML content extracted - Length: 25302 characters
[6:18:09 AM] ℹ️ Parsing HTML to extract book data
[6:18:09 AM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[6:18:09 AM] 📋 EXTRACTED BOOKS SUMMARY
[6:18:09 AM] 📚 1. "Henry Huggins" - Score: 100%%
[6:18:09 AM] 📚 2. "Anne of Green Gables" - Score: 100%%
[6:18:09 AM] 📚 3. "The Secret Garden" - Score: 100%%
[6:18:09 AM] 📊 Total books extracted: 3
[6:18:09 AM] 📊 Average relevance score: 100%
[6:18:09 AM] ℹ️ Step 2: Extracting citation texts from book elements
[6:18:09 AM] 📋 📚 EXTRACTING CITATION TEXTS FROM BOOK ELEMENTS
[6:18:09 AM] ℹ️ Step 1: Waiting for main book section
[6:18:09 AM] ℹ️ Found 7 total accordion sections
[6:18:09 AM] ℹ️ Found individual book: "1. Henry Huggins"
[6:18:09 AM] ℹ️ Found individual book: "2. Anne of Green Gables"
[6:18:09 AM] ℹ️ Found individual book: "3. The Secret Garden"
[6:18:09 AM] ℹ️ Processing 3 individual book sections
[6:18:09 AM] 📋 📖 PROCESSING BOOK 1: "Henry Huggins"
[6:18:09 AM] ℹ️ Expanding book section: "Henry Huggins"
[6:18:12 AM] ✅ Book section expanded: "Henry Huggins"
[6:18:12 AM] ℹ️ Expanding "Why this book is the match" section for: "Henry Huggins"
[6:18:15 AM] ✅ "Why this book is the match" section expanded for: "Henry Huggins"
[6:18:15 AM] ℹ️ Found 3 citation buttons for "Henry Huggins"
[6:18:15 AM] ℹ️ Extracting citation 1/3 for "Henry Huggins"
[6:18:15 AM] ℹ️ Processing metadata citation 1 for "Henry Huggins"
[6:18:16 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:18:18 AM] ℹ️ Extracted citation text (first 100 chars): classic...
[6:18:18 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:18:20 AM] ✅ ✅ metadata citation 1 closed successfully
[6:18:20 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 7)
[6:18:20 AM] ℹ️ Extracting citation 2/3 for "Henry Huggins"
[6:18:20 AM] ℹ️ Processing metadata citation 2 for "Henry Huggins"
[6:18:21 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:18:23 AM] ℹ️ Extracted citation text (first 100 chars): Just as Henry Huggins is complaining that nothing exciting ever happens, a friendly dog sits down be...
[6:18:23 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:18:24 AM] ✅ ✅ metadata citation 2 closed successfully
[6:18:24 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 390)
[6:18:24 AM] ℹ️ Extracting citation 3/3 for "Henry Huggins"
[6:18:24 AM] ℹ️ Processing metadata citation 3 for "Henry Huggins"
[6:18:25 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:18:27 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:18:27 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:18:29 AM] ✅ ✅ metadata citation 3 closed successfully
[6:18:29 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:18:29 AM] ℹ️ Collapsing book section: "Henry Huggins"
[6:18:30 AM] ✅ Book section collapsed: "Henry Huggins"
[6:18:30 AM] ✅ ✅ Completed processing "Henry Huggins" - 3 citations extracted
[6:18:30 AM] 📋 📖 PROCESSING BOOK 2: "Anne of Green Gables"
[6:18:30 AM] ℹ️ Expanding book section: "Anne of Green Gables"
[6:18:33 AM] ✅ Book section expanded: "Anne of Green Gables"
[6:18:33 AM] ℹ️ Expanding "Why this book is the match" section for: "Anne of Green Gables"
[6:18:36 AM] ✅ "Why this book is the match" section expanded for: "Anne of Green Gables"
[6:18:36 AM] ℹ️ Found 3 citation buttons for "Anne of Green Gables"
[6:18:36 AM] ℹ️ Extracting citation 1/3 for "Anne of Green Gables"
[6:18:36 AM] ℹ️ Processing metadata citation 1 for "Anne of Green Gables"
[6:18:37 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:18:40 AM] ℹ️ Extracted citation text (first 100 chars): classic...
[6:18:40 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:18:41 AM] ✅ ✅ metadata citation 1 closed successfully
[6:18:41 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 7)
[6:18:41 AM] ℹ️ Extracting citation 2/3 for "Anne of Green Gables"
[6:18:41 AM] ℹ️ Processing metadata citation 2 for "Anne of Green Gables"
[6:18:42 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:18:44 AM] ℹ️ Extracted citation text (first 100 chars): a talkative eleven-year-old orphan with a heart full of dreams and a desperate longing for a home....
[6:18:44 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:18:46 AM] ✅ ✅ metadata citation 2 closed successfully
[6:18:46 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 98)
[6:18:46 AM] ℹ️ Extracting citation 3/3 for "Anne of Green Gables"
[6:18:46 AM] ℹ️ Processing metadata citation 3 for "Anne of Green Gables"
[6:18:47 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:18:49 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:18:49 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:18:50 AM] ✅ ✅ metadata citation 3 closed successfully
[6:18:50 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:18:50 AM] ℹ️ Collapsing book section: "Anne of Green Gables"
[6:18:52 AM] ✅ Book section collapsed: "Anne of Green Gables"
[6:18:52 AM] ✅ ✅ Completed processing "Anne of Green Gables" - 3 citations extracted
[6:18:52 AM] 📋 📖 PROCESSING BOOK 3: "The Secret Garden"
[6:18:52 AM] ℹ️ Expanding book section: "The Secret Garden"
[6:18:55 AM] ✅ Book section expanded: "The Secret Garden"
[6:18:55 AM] ℹ️ Expanding "Why this book is the match" section for: "The Secret Garden"
[6:18:58 AM] ✅ "Why this book is the match" section expanded for: "The Secret Garden"
[6:18:58 AM] ℹ️ Found 3 citation buttons for "The Secret Garden"
[6:18:58 AM] ℹ️ Extracting citation 1/3 for "The Secret Garden"
[6:18:58 AM] ℹ️ Processing metadata citation 1 for "The Secret Garden"
[6:18:59 AM] ℹ️ Clicking to OPEN metadata citation 1
[6:19:01 AM] ℹ️ Extracted citation text (first 100 chars): classic literature...
[6:19:01 AM] ℹ️ Clicking to CLOSE metadata citation 1
[6:19:03 AM] ✅ ✅ metadata citation 1 closed successfully
[6:19:03 AM] ℹ️ ✅ Citation 1 extracted successfully (length: 18)
[6:19:03 AM] ℹ️ Extracting citation 2/3 for "The Secret Garden"
[6:19:03 AM] ℹ️ Processing metadata citation 2 for "The Secret Garden"
[6:19:04 AM] ℹ️ Clicking to OPEN metadata citation 2
[6:19:06 AM] ℹ️ Extracted citation text (first 100 chars): Mary discovers a secret garden, surrounded by walls and locked with a missing key. One day, with the...
[6:19:06 AM] ℹ️ Clicking to CLOSE metadata citation 2
[6:19:07 AM] ✅ ✅ metadata citation 2 closed successfully
[6:19:07 AM] ℹ️ ✅ Citation 2 extracted successfully (length: 228)
[6:19:07 AM] ℹ️ Extracting citation 3/3 for "The Secret Garden"
[6:19:07 AM] ℹ️ Processing metadata citation 3 for "The Secret Garden"
[6:19:08 AM] ℹ️ Clicking to OPEN metadata citation 3
[6:19:10 AM] ℹ️ Extracted citation text (first 100 chars): JUVENILE FICTION...
[6:19:10 AM] ℹ️ Clicking to CLOSE metadata citation 3
[6:19:12 AM] ✅ ✅ metadata citation 3 closed successfully
[6:19:12 AM] ℹ️ ✅ Citation 3 extracted successfully (length: 16)
[6:19:12 AM] ℹ️ Collapsing book section: "The Secret Garden"
[6:19:13 AM] ✅ Book section collapsed: "The Secret Garden"
[6:19:13 AM] ✅ ✅ Completed processing "The Secret Garden" - 3 citations extracted
[6:19:13 AM] ✅ 🎉 Successfully extracted citations from 3 books
[6:19:13 AM] ℹ️ Step 3: Validating reason-citation matches
[6:19:13 AM] 📋 🔍 VALIDATING REASON-CITATION MATCHES (80% THRESHOLD)
[6:19:13 AM] ℹ️ 📖 Validating 3 reasons for: "Henry Huggins"
[6:19:13 AM] ℹ️ Reason 1: Checking match...
[6:19:13 AM] ✅ ✅ "Henry Huggins" - Reason 1: 100% match - PASS
[6:19:13 AM] ℹ️ Reason 2: Checking match...
[6:19:21 AM] ✅ ✅ "Henry Huggins" - Reason 2: 95% match - PASS
[6:19:21 AM] ℹ️ Reason 3: Checking match...
[6:19:21 AM] ✅ ✅ "Henry Huggins" - Reason 3: 100% match - PASS
[6:19:21 AM] ℹ️ 📖 Validating 3 reasons for: "Anne of Green Gables"
[6:19:21 AM] ℹ️ Reason 1: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 1: 100% match - PASS
[6:19:21 AM] ℹ️ Reason 2: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 2: 100% match - PASS
[6:19:21 AM] ℹ️ Reason 3: Checking match...
[6:19:21 AM] ✅ ✅ "Anne of Green Gables" - Reason 3: 100% match - PASS
[6:19:21 AM] ℹ️ 📖 Validating 3 reasons for: "The Secret Garden"
[6:19:21 AM] ℹ️ Reason 1: Checking match...
[6:19:31 AM] ✅ ✅ "The Secret Garden" - Reason 1: 95% match - PASS
[6:19:31 AM] ℹ️ Reason 2: Checking match...
[6:19:39 AM] ✅ ✅ "The Secret Garden" - Reason 2: 95% match - PASS
[6:19:39 AM] ℹ️ Reason 3: Checking match...
[6:19:39 AM] ✅ ✅ "The Secret Garden" - Reason 3: 100% match - PASS
[6:19:39 AM] 📊 📊 CITATION VALIDATION SUMMARY: 9/9 passed (100.0%)
[6:19:39 AM] ✅ 🎉 REASON-CITATION VALIDATION: OVERALL PASSED
[6:19:39 AM] ℹ️ Step 4: Generating detailed report
[6:19:39 AM] 📋 📋 GENERATING CITATION VALIDATION REPORT
[6:19:39 AM] ℹ️ Report generated: 9/9 reasons passed
[6:19:39 AM] ✅ ✅ Citation validation workflow completed successfully
[6:19:39 AM] 📊 📈 OVERALL CITATION VALIDATION: 9/9 (100.0%)
[6:19:39 AM] ✅ ✅ REASON-CITATION VALIDATION: OVERALL PASSED
[6:19:39 AM] 📋 📋 GENERATING DETAILED CITATION VALIDATION REPORT
[6:19:39 AM] ℹ️ Generating HTML citation validation report
[6:19:39 AM] ✅ Generated HTML report for 9 reasons
[6:19:39 AM] ✅ ✅ Citation validation HTML report generated and attached
[6:19:39 AM] ✅ ✅ Citation validation plain text report attached
[6:19:39 AM] ℹ️ Checking database connectivity...
[6:19:40 AM] ✅ ✅ Database connected successfully
[6:19:40 AM] ℹ️ Database path: /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/test_data/database.xlsx
[6:19:40 AM] ℹ️ Total books: 10032
[6:19:40 AM] 📊 Database contains 10032 books
[6:19:40 AM] ✅ ✅ Database book count requirement met: 10032 >= 2020
[6:19:40 AM] 📋 🔍 VALIDATING EXTRACTED BOOKS INDIVIDUALLY AGAINST DATABASE
[6:19:40 AM] ℹ️ Checking book 1/3: "Henry Huggins"
[6:19:40 AM] ℹ️ Checking if book exists in database: "Henry Huggins"
[6:19:40 AM] ✅ ✅ Database match: "Henry Huggins" → "Henry Huggins 4-Book Collection"
[6:19:40 AM] ℹ️ Checking book 2/3: "Anne of Green Gables"
[6:19:40 AM] ℹ️ Checking if book exists in database: "Anne of Green Gables"
[6:19:40 AM] ✅ ✅ Database match: "Anne of Green Gables" → "Anne of Green Gables"
[6:19:40 AM] ℹ️ Checking book 3/3: "The Secret Garden"
[6:19:40 AM] ℹ️ Checking if book exists in database: "The Secret Garden"
[6:19:40 AM] ✅ ✅ Database match: "The Secret Garden" → "The Secret Garden"
[6:19:40 AM] 📊 INDIVIDUAL DATABASE VALIDATION SUMMARY: 3/3 books found in database
[6:19:40 AM] ✅ 🎉 ALL BOOKS VALIDATED SUCCESSFULLY AGAINST DATABASE!
[6:19:40 AM] 📋 🤖 VALIDATING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[6:19:40 AM] ℹ️ Starting Gemini AI per-book relevance validation...
[6:19:40 AM] 📋 🤖 ANALYZING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[6:19:40 AM] ℹ️ Starting Gemini AI analysis with retry logic...
[6:19:47 AM] ⚠ ⚠️ GEMINI VALIDATION: Using fallback analysis due to API issues
[6:19:47 AM] ℹ️ Overall Score: 70% (Fallback)
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "Henry Huggins" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "Anne of Green Gables" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] 📋 📖 ANALYSIS FOR: "The Secret Garden" - Score: 70%
[6:19:47 AM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[6:19:47 AM] ℹ️ 💡 Book-specific improvements:
[6:19:47 AM] ℹ️ - Retry analysis when service is available
[6:19:47 AM] ℹ️ 📊 SUMMARY FEEDBACK:
[6:19:47 AM] ℹ️ • AI analysis service is temporarily overloaded
[6:19:47 AM] ℹ️ • Using fallback analysis with neutral scores
[6:19:47 AM] ℹ️ • Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 12.904344956s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"location":"global","model":"gemini-2.5-pro"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"12s"}]
[6:19:47 AM] ❌ ❌ GEMINI AI VALIDATION FAILED: 70% relevance score (required: 80%)
[6:19:47 AM] ℹ️ Continuing execution due to soft assertion...